**** dT 0.000 * top TEST ../../../bin/varnishtest/tests/r03940.vtc starting **** top extmacro def pkg_version=trunk **** top extmacro def pkg_branch=trunk **** top extmacro def pwd=/tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishtest **** top extmacro def date(...) **** top extmacro def string(...) **** top extmacro def localhost=fc00:a462:8e9e:946a:5df3:31c6:d23:18dc **** top extmacro def bad_backend=[fc00:a462:8e9e:946a:5df3:31c6:d23:18dc]:37428 **** top extmacro def listen_addr=[fc00:a462:8e9e:946a:5df3:31c6:d23:18dc]:0 **** top extmacro def bad_ip=192.0.2.255 **** top extmacro def topbuild=/tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build **** top extmacro def topsrc=/tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/.. **** top macro def testdir=/tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishtest/../../../bin/varnishtest/tests **** top macro def tmpdir=/tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0 **** top macro def vtcid=vtc.397733.40c1e3f0 ** top === varnishtest "test startup_timeout vs. stevedore init / open" * top VTEST test startup_timeout vs. stevedore init / open ** top === varnish v1 -arg "-sdebug=debug,dinit=5s -pstartup_timeout=3s... **** dT 0.010 ** v1 Launch *** v1 CMD: cd ${pwd} && exec varnishd -d -n /tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/v1 -i v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '[fc00:a462:8e9e:946a:5df3:31c6:d23:18dc]:0' -M 'fc00:a462:8e9e:946a:5df3:31c6:d23:18dc 63708' -P /tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/v1/varnishd.pid -p vmod_path=/tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod/.libs -sdebug=debug,dinit=5s -pstartup_timeout=3s -pcli_timeout=2s -p feature=+no_coredump *** v1 CMD: cd /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishtest && exec varnishd -d -n /tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/v1 -i v1 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '[fc00:a462:8e9e:946a:5df3:31c6:d23:18dc]:0' -M 'fc00:a462:8e9e:946a:5df3:31c6:d23:18dc 63708' -P /tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/v1/varnishd.pid -p vmod_path=/tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod/.libs -sdebug=debug,dinit=5s -pstartup_timeout=3s -pcli_timeout=2s -p feature=+no_coredump **** dT 0.011 *** v1 PID: 397743 **** v1 macro def v1_pid=397743 **** v1 macro def v1_name=/tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/v1 **** dT 0.042 *** v1 debug|Warning: mlock() of VSM failed: Not owner (1 *** v1 debug|) **** dT 0.045 *** v1 debug|Debug: Version: varnish-trunk revision 4b1e115fa16df27fde4777936c925898d2f31872 *** v1 debug| *** v1 debug|Debug: *** v1 debug| *** v1 debug|Platform: -jsolaris,-sdebug,-sdefault,-hcritbit *** v1 debug| *** v1 debug|200 288 *** v1 debug|----------------------------- *** v1 debug|Varnish Cache CLI 1.0 *** v1 debug|----------------------------- *** v1 debug|-jsolaris,-sdebug,-sdefault,-hcritbit *** v1 debug|varnish-trunk revision 4b1e115fa16df27fde4777936c925898d2f31872 *** v1 debug| *** v1 debug|Type 'help' for command list. *** v1 debug|Type 'quit' to close CLI session. *** v1 debug|Type 'start' to launch worker process. *** v1 debug| **** dT 0.143 **** v1 CLIPOLL 1 0x1 0x0 0x0 *** v1 CLI connection fd = 4 **** dT 0.144 *** v1 CLI RX 107 **** v1 CLI RX|hhcmhvqjjemtxainbvmbvopqhfgbqazt **** v1 CLI RX| **** v1 CLI RX|Authentication required. **** v1 CLI TX|auth b345401e43380751ec593ed525481595f3bacea89f6cf489a58b327b7e27e1ee **** dT 0.145 *** v1 CLI RX 200 **** v1 CLI RX|----------------------------- **** v1 CLI RX|Varnish Cache CLI 1.0 **** v1 CLI RX|----------------------------- **** v1 CLI RX|-jsolaris,-sdebug,-sdefault,-hcritbit **** v1 CLI RX|varnish-trunk revision 4b1e115fa16df27fde4777936c925898d2f31872 **** v1 CLI RX| **** v1 CLI RX|Type 'help' for command list. **** v1 CLI RX|Type 'quit' to close CLI session. **** v1 CLI RX|Type 'start' to launch worker process. **** v1 CLI TX|vcl.inline vcl1 << %XJEIFLH|)Xspa8P **** v1 CLI TX|vcl 4.1; **** v1 CLI TX|backend none none; **** v1 CLI TX|%XJEIFLH|)Xspa8P **** dT 0.245 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.346 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.446 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.546 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.592 *** v1 CLI RX 200 **** v1 CLI RX|Message from VCC-compiler: **** v1 CLI RX|Security: Privilege proc_setid missing, will not change uid/gid **** v1 CLI RX|Message from C-compiler: **** v1 CLI RX|Security: Privilege proc_setid missing, will not change uid/gid **** v1 CLI RX|cc: Warning: Option -fno-var-tracking-assignments passed to ld, if ld is invoked, ignored otherwise **** v1 CLI RX|Message from dlopen: **** v1 CLI RX|Security: Privilege proc_setid missing, will not change uid/gid **** v1 CLI RX|VCL compiled. **** v1 CLI TX|vcl.use vcl1 *** v1 CLI RX 200 **** v1 CLI RX|VCL 'vcl1' now active ** top === varnish v1 -cliexpect \ **** v1 CLI TX|start **** dT 0.603 *** v1 debug|Debug: *** v1 debug| Child (397803) Started **** dT 0.646 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.746 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.846 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 0.946 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.046 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.146 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.246 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.346 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.446 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.546 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.646 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.746 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.847 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 1.947 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.047 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.147 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.247 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.347 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.447 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.547 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.647 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.747 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.847 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 2.948 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.048 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.148 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.248 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.348 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.448 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.548 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.603 *** v1 debug|Error: Child failed on launch within startup_timeout=3.00s *** v1 debug|Error: Child (397803) said Child starts *** v1 debug| *** v1 debug|Error: Child (397803) said -sdebug init delay 5.000000s *** v1 debug|Error: Child (397803) said -sdebug open delay in init 0.000000s **** dT 3.648 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.748 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.848 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 3.948 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 4.048 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 4.148 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 4.248 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 4.349 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 4.449 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 4.549 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 4.649 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 4.749 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 4.849 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 4.949 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 5.049 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 5.149 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 5.249 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 5.349 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 5.449 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 5.549 *** v1 vsl|No VSL chunk found (child not started ?) **** dT 5.639 *** v1 debug|Error: Child (397803) not dying (waitpid = 0), killing *** v1 debug|Child (397803) died signal=6 *** v1 debug|Error: *** v1 debug|Child (397803) Panic at: Wed, 17 Sep 2025 21:26:15 GMT *** v1 debug|Assert error in CLI_Run(), ../../../bin/varnishd/cache/cache_cli.c line 103: *** v1 debug| Condition((VCLI_WriteResult(heritage.cli_fd, CLIS_OK, \"Ready\")) == 0) not true. *** v1 debug|version = varnish-trunk revision 4b1e115fa16df27fde4777936c925898d2f31872, vrt api = 22.0 *** v1 debug|ident = -jsolaris,-sdebug,-sdefault,-hcritbit,ports *** v1 debug|now = 29230093.573050 (mono), 1758144375.716584 (real) *** v1 debug|Backtrace: *** v1 debug| 824590d: /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'vbt_execinfo+0x2d [0x824590d] *** v1 debug| 8245bde: /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'VBT_format+0x3e [0x8245bde] *** v1 debug| 811f482: /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'pan_backtrace+0x42 [0x811f482] *** v1 debug| 811f998: /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'pan_ic+0x3b8 [0x811f998] *** v1 debug| 82446b4: /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish- *** v1 debug|trunk/_build/bin/varnishd/varnishd'VAS_Fail+0x54 [0x82446b4] *** v1 debug| 80ceea3: /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'CLI_Run+0x63 [0x80ceea3] *** v1 debug| 81136c9: /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'child_main+0x4e9 [0x81136c9] *** v1 debug| 81b5a41: /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'mgt_launch_child+0x601 [0x81b5a41] *** v1 debug| 81b6f95: /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'mch_cli_server_start+0x35 [0x81b6f95] *** v1 debug| 824758f: /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'cls_dispatch+0x46f [0x824758f] *** v1 debug| 82478f9: /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'cls_exec+0x339 [0x82478f9] *** v1 debug| 824808d: /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'cls_feed+0x52d [0x824808d] *** v1 debug| 8249940: /tmp/vtest. *** v1 debug|suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'VCLS_Poll+0x340 [0x8249940] *** v1 debug| 81b8748: /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'mgt_cli_callback2+0x28 [0x81b8748] *** v1 debug| 824deff: /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'VEV_Once+0x69f [0x824deff] *** v1 debug| 824d4d5: /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'VEV_Loop+0xf5 [0x824d4d5] *** v1 debug| 81c3618: /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'main+0x21f8 [0x81c3618] *** v1 debug| 80a81f2: /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishd/varnishd'_start+0x72 [0x80a81f2] *** v1 debug|errno = 32 (Broken pipe) *** v1 debug|argv = { *** v1 debug| [0] = \"varnishd\", *** v1 debug| [1] = \"-d\", *** v1 debug| [2] = \"-n\", *** v1 debug| [3] = \"/tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/v1\", *** v1 debug| [4] = \"-i\", *** v1 debug| [5] = \"v1\", *** v1 debug| [6] = \"-l\", *** v1 debug| [7] = \"2m\", *** v1 debug| [8] = \"-p\", *** v1 debug| [9] *** v1 debug|= \"auto_restart=off\", *** v1 debug| [10] = \"-p\", *** v1 debug| [11] = \"syslog_cli_traffic=off\", *** v1 debug| [12] = \"-p\", *** v1 debug| [13] = \"thread_pool_min=10\", *** v1 debug| [14] = \"-p\", *** v1 debug| [15] = \"debug=+vtc_mode\", *** v1 debug| [16] = \"-p\", *** v1 debug| [17] = \"vsl_mask=+Debug,+H2RxHdr,+H2RxBody\", *** v1 debug| [18] = \"-p\", *** v1 debug| [19] = \"h2_initial_window_size=1m\", *** v1 debug| [20] = \"-p\", *** v1 debug| [21] = \"h2_rx_window_low_water=64k\", *** v1 debug| [22] = \"-a\", *** v1 debug| [23] = \"[fc00:a462:8e9e:946a:5df3:31c6:d23:18dc]:0\", *** v1 debug| [24] = \"-M\", *** v1 debug| [25] = \"fc00:a462:8e9e:946a:5df3:31c6:d23:18dc 63708\", *** v1 debug| [26] = \"-P\", *** v1 debug| [27] = \"/tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/v1/varnishd.pid\", *** v1 debug| [28] = \"-p\", *** v1 debug| [29] = \"vmod_path=/tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod/.libs\", *** v1 debug| [30] = \"-sdebug=debug,dinit=5s\", *** v1 debug| [31] = \"-pstartup_timeout=3s\", *** v1 debug| [32] = \"-pcli_timeout=2s\", *** v1 debug| [33] = \"-p\", *** v1 debug| [34] = \"feature=+no_coredump\", *** v1 debug|} *** v1 debug|pthread.self = 1 *** v1 debug|pthread.name = (cache-main) *** v1 debug|thr.req = NULL *** v1 debug|thr.busyobj = NULL *** v1 debug|thr.worker = NULL *** v1 debug|vmods = { *** v1 debug|}, *** v1 debug|pools = { *** v1 debug| p *** v1 CLI RX 400 **** v1 CLI RX|Child failed on launch within startup_timeout=3.00s *** v1 debug|ool = 83b5b30 { *** v1 debug| nidle = 9, *** v1 debug| nthr = 10, *** v1 debug| lqueue = 0 *** v1 debug| }, *** v1 debug| pool = 83f6498 { *** v1 debug| nidle = 9, *** v1 debug| nthr = 10, *** v1 debug| lqueue = 0 *** v1 debug| }, *** v1 debug|}, *** v1 debug| *** v1 debug| *** v1 debug|Error: Child (397803) said Security: Privilege proc_setid missing, will not change uid/gid *** v1 debug|Error: Child (397803) said -sdebug open delay 0.000000s *** v1 debug|Debug: Child cleanup complete ** v1 CLI 400 ** top === shell {varnishadm -n ${v1_name} panic.clear || true } **** top shell_cmd|set -e ;exec 2>&1 ; varnishadm -n /tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/v1 panic.clear || true **** dT 5.703 **** top shell_out| **** dT 5.704 **** top shell_status = 0x0000 ** top === varnish v1 -wait ** v1 Wait **** v1 CLI TX|backend.list *** v1 CLI RX 101 **** v1 CLI RX|Unknown request in manager process (child not running). **** v1 CLI RX|Type 'help' for more info. ** v1 Stop **** v1 CLI TX|stop *** v1 CLI RX 300 **** v1 CLI RX|Child in state stopped *** v1 wait-stopped **** v1 CLI TX|status *** v1 CLI RX 200 **** v1 CLI RX|Child in state stopped **** v1 CLI TX|panic.show *** v1 CLI RX 300 **** v1 CLI RX|Child has not panicked or panic has been cleared *** v1 debug|Info: manager stopping child *** v1 debug|Info: manager dies **** v1 STDOUT EOF **** dT 5.750 ** v1 WAIT4 pid=397743 status=0x4000 (user 0.588000 sys 0.236000) ** top === process p1 { varnishd \ **** dT 5.763 **** p1 CMD: varnishd \ -sdebug=debug,dinit=5s \ -pstartup_timeout=3s -pcli_timeout=2s \ -n /tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/p1 -a :0 -b none 2>&1 **** dT 5.764 *** p1 PID: 398591 **** dT 5.765 **** p1 macro def p1_pid=398591 **** p1 macro def p1_dir=/tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/p1 **** p1 macro def p1_out=/tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/p1/term **** p1 macro def p1_err=/tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/p1/stderr **** dT 11.458 **** p1 stderr read 0 **** p1 stdout read 0 **** dT 11.460 ** p1 WAIT4 pid=398591 status=0x0200 (user 0.003000 sys 0.008000) **** p1 macro undef p1_pid **** p1 stdout 3417 bytes, stderr 0 bytes ** top === shell {grep -q "Child failed on launch within startup_timeou... **** top shell_cmd|set -e ;exec 2>&1 ; grep -q "Child failed on launch within startup_timeout=3.00s" /tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/p1/term **** dT 11.482 **** top shell_status = 0x0000 ** top === varnish v2 -arg "-sdebug=debug,dopen=5s -pstartup_timeout=2s... **** dT 11.494 ** v2 Launch *** v2 CMD: cd ${pwd} && exec varnishd -d -n /tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/v2 -i v2 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '[fc00:a462:8e9e:946a:5df3:31c6:d23:18dc]:0' -M 'fc00:a462:8e9e:946a:5df3:31c6:d23:18dc 51579' -P /tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/v2/varnishd.pid -p vmod_path=/tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod/.libs -sdebug=debug,dopen=5s -pstartup_timeout=2s -pcli_timeout=3s -p feature=+no_coredump *** v2 CMD: cd /tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/bin/varnishtest && exec varnishd -d -n /tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/v2 -i v2 -l 2m -p auto_restart=off -p syslog_cli_traffic=off -p thread_pool_min=10 -p debug=+vtc_mode -p vsl_mask=+Debug,+H2RxHdr,+H2RxBody -p h2_initial_window_size=1m -p h2_rx_window_low_water=64k -a '[fc00:a462:8e9e:946a:5df3:31c6:d23:18dc]:0' -M 'fc00:a462:8e9e:946a:5df3:31c6:d23:18dc 51579' -P /tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/v2/varnishd.pid -p vmod_path=/tmp/vtest.suncc12.6_m32_IPv6_uffix/varnish-cache/varnish-trunk/_build/vmod/.libs -sdebug=debug,dopen=5s -pstartup_timeout=2s -pcli_timeout=3s -p feature=+no_coredump *** v2 PID: 399522 **** v2 macro def v2_pid=399522 **** v2 macro def v2_name=/tmp/vtest.suncc12.6_m32_IPv6_uffix/_vtest_tmp/vtc.397733.40c1e3f0/v2 **** dT 11.537 *** v2 debug|Warning: mlock() of VSM failed: Not owner (1) **** dT 11.540 *** v2 debug|Debug: Version: varnish-trunk revision 4b1e115fa16df27fde4777936c925898d2f31872 *** v2 debug| *** v2 debug|Debug: *** v2 debug| Platform: -jsolaris,-sdebug,-sdefault,-hcritbit *** v2 debug| *** v2 debug|200 288 *** v2 debug|----------------------------- *** v2 debug|Varnish Cache CLI 1.0 *** v2 debug|----------------------------- *** v2 debug|-jsolaris,-sdebug,-sdefault,-hcritbit *** v2 debug|varnish-trunk revision 4b1e115fa16df27fde4777936c925898d2f31872 *** v2 debug| *** v2 debug|Type 'help' for command list. *** v2 debug|Type 'quit' to close CLI session. *** v2 debug|Type 'start' to launch worker process. *** v2 debug| **** dT 11.638 **** v2 CLIPOLL 1 0x1 0x0 0x0 *** v2 CLI connection fd = 13 *** v2 CLI RX 107 **** v2 CLI RX|lnapioyjpejkqtblrrirlyhzozugwjzh **** v2 CLI RX| **** v2 CLI RX|Authentication required. **** dT 11.639 **** v2 CLI TX|auth 558a81d84e6e45bf6aeec7f44a73337e19630effa8a7b8c3a1ec3718d7885ed9 *** v2 CLI RX 200 **** v2 CLI RX|----------------------------- **** v2 CLI RX|Varnish Cache CLI 1.0 **** v2 CLI RX|----------------------------- **** v2 CLI RX|-jsolaris,-sdebug,-sdefault,-hcritbit **** v2 CLI RX|varnish-trunk revision 4b1e115fa16df27fde4777936c925898d2f31872 **** v2 CLI RX| **** v2 CLI RX|Type 'help' for command list. **** v2 CLI RX|Type 'quit' to close CLI session. **** v2 CLI RX|Type 'start' to launch worker process. **** v2 CLI TX|vcl.inline vcl1 << %XJEIFLH|)Xspa8P **** v2 CLI TX|vcl 4.1; **** v2 CLI TX|backend none none; **** v2 CLI TX|%XJEIFLH|)Xspa8P **** dT 11.739 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 11.840 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 11.940 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 12.040 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 12.140 *** v2 vsl|No VSL chunk found (child not started ?) **** dT 12.157 *** v2 CLI RX 200 **** v2 CLI RX|Message from VCC-compiler: **** v2 CLI RX|Security: Privilege proc_setid missing, will not change uid/gid **** v2 CLI RX|Message from C-compiler: **** v2 CLI RX|Security: Privilege proc_setid missing, will not change uid/gid **** v2 CLI RX|cc: Warning: Option -fno-var-tracking-assignments passed to ld, if ld is invoked, ignored otherwise **** v2 CLI RX|Message from dlopen: **** v2 CLI RX|Security: Privilege proc_setid missing, will not change uid/gid **** v2 CLI RX|VCL compiled. **** v2 CLI TX|vcl.use vcl1 *** v2 CLI RX 200 **** v2 CLI RX|VCL 'vcl1' now active ** top === varnish v2 -cliexpect \ **** v2 CLI TX|start **** dT 12.169 *** v2 debug|Debug: *** v2 debug| Child (399674) Started **** dT 15.180 *** v2 debug|Error: Child failed on launch within cli_timeout=3.00s (tip: set startup_timeout) *** v2 debug|Error: Child (399674) said Child starts **** dT 15.181 *** v2 debug|Error: Child (399674) said -sdebug init delay 0.000000s *** v2 debug|Error: Child (399674) said -sdebug open delay in init 5.000000s *** v2 debug|Error: Child (399674) said Security: Privilege proc_setid missing, will not change uid/gid *** v2 debug| *** v2 debug|Error: *** v2 debug| *** v2 debug|Child (399674) said -sdebug open delay 5.000000s *** v2 debug| **** dT 15.281 *** v2 debug|Error: Child (399674) died signal=3 *** v2 debug|Debug: *** v2 debug|Child cleanup complete *** v2 CLI RX 400 **** v2 CLI RX|Child failed on launch within cli_timeout=3.00s (tip: set startup_timeout) ** v2 CLI 400 ** top === varnish v2 -cliok "panic.clear" **** v2 CLI TX|panic.clear *** v2 CLI RX 300 **** v2 CLI RX|No panic to clear ** v2 CLI 300 **** dT 15.291 ---- v2 FAIL CLI response 300 expected 200 * top RESETTING after ../../../bin/varnishtest/tests/r03940.vtc ** v2 Wait **** v2 CLI TX|panic.show *** v2 CLI RX 300 **** v2 CLI RX|Child has not panicked or panic has been cleared *** v2 debug|Info: manager stopping child *** v2 debug|Info: manager dies **** dT 15.292 **** v2 STDOUT EOF **** dT 15.343 ** v2 WAIT4 pid=399522 status=0x4000 (user 0.630000 sys 0.245000) * top TEST ../../../bin/varnishtest/tests/r03940.vtc FAILED # top TEST ../../../bin/varnishtest/tests/r03940.vtc FAILED (15.345) exit=2